[INFO] cloning repository https://github.com/consistent-milk12/pbench
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/consistent-milk12/pbench" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fconsistent-milk12%2Fpbench", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fconsistent-milk12%2Fpbench'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 3161f4351c46f147091e02f0e3195a14294b32cd
[INFO] testing consistent-milk12/pbench against try#b8e88e5ddf5521a9f43ee3f62a702388c713e4bb for pr-155114
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fconsistent-milk12%2Fpbench" "/workspace/builds/worker-7-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-7-tc2/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-7-tc2/source/rust-toolchain.toml
[INFO] started tweaking git repo https://github.com/consistent-milk12/pbench
[INFO] finished tweaking git repo https://github.com/consistent-milk12/pbench
[INFO] tweaked toml for git repo https://github.com/consistent-milk12/pbench written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/consistent-milk12/pbench on toolchain b8e88e5ddf5521a9f43ee3f62a702388c713e4bb
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/consistent-milk12/pbench already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded trybuild v1.0.116
[INFO] [stderr]   Downloaded toml v1.0.2+spec-1.1.0
[INFO] [stderr]   Downloaded seize v0.5.1
[INFO] [stderr]   Downloaded sdd v4.6.2
[INFO] [stderr]   Downloaded saa v5.5.0
[INFO] [stderr]   Downloaded scc v3.6.5
[INFO] [stderr]   Downloaded masstree v0.8.1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 4d0abd0b7c0480d289b31cf06a4255a26f7b205daf0e922a82bb9aa61771f729
[INFO] running `Command { std: "docker" "start" "-a" "4d0abd0b7c0480d289b31cf06a4255a26f7b205daf0e922a82bb9aa61771f729", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "4d0abd0b7c0480d289b31cf06a4255a26f7b205daf0e922a82bb9aa61771f729", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4d0abd0b7c0480d289b31cf06a4255a26f7b205daf0e922a82bb9aa61771f729", kill_on_drop: false }`
[INFO] [stdout] 4d0abd0b7c0480d289b31cf06a4255a26f7b205daf0e922a82bb9aa61771f729
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] defe7b1d1074d677df0a55fa2d91d127450aacd48b52cdfc60f4c5e0f9571cb4
[INFO] running `Command { std: "docker" "start" "-a" "defe7b1d1074d677df0a55fa2d91d127450aacd48b52cdfc60f4c5e0f9571cb4", kill_on_drop: false }`
[INFO] [stderr]    Compiling unicode-ident v1.0.23
[INFO] [stderr]    Compiling simd-adler32 v0.3.8
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling nom v7.1.3
[INFO] [stderr]    Compiling crossbeam-channel v0.5.15
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling flate2 v1.1.9
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling hdrhistogram v7.5.4
[INFO] [stderr]    Compiling pbench-macros v0.2.0 (/opt/rustwide/workdir/pbench-macros)
[INFO] [stderr]    Compiling pbench v0.2.0 (/opt/rustwide/workdir/pbench)
[INFO] [stdout] warning: unnecessary `unsafe` block
[INFO] [stdout]   --> pbench/src/time/tsc.rs:97:9
[INFO] [stdout]    |
[INFO] [stdout] 97 |         unsafe { x86::__cpuid(leaf) }
[INFO] [stdout]    |         ^^^^^^ unnecessary `unsafe` block
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_unsafe)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 13.95s
[INFO] running `Command { std: "docker" "inspect" "defe7b1d1074d677df0a55fa2d91d127450aacd48b52cdfc60f4c5e0f9571cb4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "defe7b1d1074d677df0a55fa2d91d127450aacd48b52cdfc60f4c5e0f9571cb4", kill_on_drop: false }`
[INFO] [stdout] defe7b1d1074d677df0a55fa2d91d127450aacd48b52cdfc60f4c5e0f9571cb4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] be0cd5ee4e9c67fdb907012783fcb0b94d89491ef09b7b6b988af73f8877488d
[INFO] running `Command { std: "docker" "start" "-a" "be0cd5ee4e9c67fdb907012783fcb0b94d89491ef09b7b6b988af73f8877488d", kill_on_drop: false }`
[INFO] [stderr]    Compiling libmimalloc-sys v0.1.44
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling target-triple v1.0.0
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling winnow v0.7.14
[INFO] [stderr]    Compiling pbench-macros v0.2.0 (/opt/rustwide/workdir/pbench-macros)
[INFO] [stderr]    Compiling serde_spanned v1.0.4
[INFO] [stderr]    Compiling crossbeam-channel v0.5.15
[INFO] [stderr]    Compiling toml_datetime v1.0.0+spec-1.1.0
[INFO] [stderr]    Compiling toml_writer v1.0.6+spec-1.1.0
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling hdrhistogram v7.5.4
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling getrandom v0.4.1
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling crossbeam-queue v0.3.12
[INFO] [stderr]    Compiling seize v0.5.1
[INFO] [stderr]    Compiling saa v5.5.0
[INFO] [stderr]    Compiling sdd v4.6.2
[INFO] [stderr]    Compiling dashmap v6.1.0
[INFO] [stderr]    Compiling pbench v0.2.0 (/opt/rustwide/workdir/pbench)
[INFO] [stderr]    Compiling mimalloc v0.1.48
[INFO] [stderr]    Compiling masstree v0.8.1
[INFO] [stderr]    Compiling scc v3.6.5
[INFO] [stdout] warning: unnecessary `unsafe` block
[INFO] [stdout]   --> pbench/src/time/tsc.rs:97:9
[INFO] [stdout]    |
[INFO] [stdout] 97 |         unsafe { x86::__cpuid(leaf) }
[INFO] [stdout]    |         ^^^^^^ unnecessary `unsafe` block
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_unsafe)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]    Compiling toml_parser v1.0.9+spec-1.1.0
[INFO] [stderr]    Compiling tempfile v3.26.0
[INFO] [stderr]    Compiling toml v1.0.2+spec-1.1.0
[INFO] [stderr]    Compiling trybuild v1.0.116
[INFO] [stdout] warning: unnecessary `unsafe` block
[INFO] [stdout]   --> pbench/src/time/tsc.rs:97:9
[INFO] [stdout]    |
[INFO] [stdout] 97 |         unsafe { x86::__cpuid(leaf) }
[INFO] [stdout]    |         ^^^^^^ unnecessary `unsafe` block
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_unsafe)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 38.02s
[INFO] running `Command { std: "docker" "inspect" "be0cd5ee4e9c67fdb907012783fcb0b94d89491ef09b7b6b988af73f8877488d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "be0cd5ee4e9c67fdb907012783fcb0b94d89491ef09b7b6b988af73f8877488d", kill_on_drop: false }`
[INFO] [stdout] be0cd5ee4e9c67fdb907012783fcb0b94d89491ef09b7b6b988af73f8877488d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 481e0b12a2abcfd58de0c784853060f8ccf4ccc395d1b2083eb6f4841a0d34b3
[INFO] running `Command { std: "docker" "start" "-a" "481e0b12a2abcfd58de0c784853060f8ccf4ccc395d1b2083eb6f4841a0d34b3", kill_on_drop: false }`
[INFO] [stderr] warning: unnecessary `unsafe` block
[INFO] [stderr]   --> pbench/src/time/tsc.rs:97:9
[INFO] [stderr]    |
[INFO] [stderr] 97 |         unsafe { x86::__cpuid(leaf) }
[INFO] [stderr]    |         ^^^^^^ unnecessary `unsafe` block
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(unused_unsafe)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `pbench` (lib) generated 1 warning
[INFO] [stderr] warning: `pbench` (lib test) generated 1 warning (1 duplicate)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.23s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/pbench-987b92feedaabf04)
[INFO] [stdout] 
[INFO] [stdout] running 241 tests
[INFO] [stdout] test bencher::unit_tests::adaptive_tuning_produces_stats ... ok
[INFO] [stdout] test bencher::unit_tests::bench_refs_collects_samples ... ok
[INFO] [stdout] test bencher::unit_tests::bench_refs_i32_output ... ok
[INFO] [stdout] test bencher::unit_tests::bench_refs_threaded_four_threads ... ok
[INFO] [stdout] test bencher::unit_tests::bench_refs_threaded_counter_length_matches_sample_count ... ok
[INFO] [stdout] test bencher::unit_tests::bench_refs_threaded_collects_samples ... ok
[INFO] [stdout] test bencher::unit_tests::bench_refs_threaded_sample_count_is_total_not_per_thread ... ok
[INFO] [stdout] test bencher::unit_tests::bench_values_collects_samples ... ok
[INFO] [stdout] test bencher::unit_tests::bench_values_string_output ... ok
[INFO] [stdout] test bencher::unit_tests::bench_refs_threaded_with_one_thread_matches_single ... ok
[INFO] [stdout] test bencher::unit_tests::bench_values_threaded_collects_samples ... ok
[INFO] [stdout] test bencher::unit_tests::bench_refs_unit_output ... ok
[INFO] [stdout] test bencher::unit_tests::context_finish_without_run_returns_none ... ok
[INFO] [stdout] test bencher::unit_tests::bench_with_counter_via_context_finish ... ok
[INFO] [stdout] test bencher::unit_tests::bench_with_counter ... ok
[INFO] [stdout] test bencher::unit_tests::bench_values_drop_correctness ... ok
[INFO] [stdout] test bencher::unit_tests::counter_overwrite_uses_last_value ... ok
[INFO] [stdout] test bencher::unit_tests::bench_without_counter_has_no_collection ... ok
[INFO] [stdout] test bencher::unit_tests::counter_length_matches_actual_sample_count ... ok
[INFO] [stdout] test bencher::unit_tests::counter_with_adaptive_tuning ... ok
[INFO] [stdout] test bencher::unit_tests::counter_with_multi_iteration_samples ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_bench_values_threaded_drop_correctness ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_bench_refs_threaded ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_bench_values_threaded ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_bench_refs ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_bench_values_threaded_non_divisible_sample_count ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_bench_refs_threaded_counter_matches ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_calls_generator_per_iteration ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_counter_uses_constant_value ... ok
[INFO] [stdout] test cli::unit_tests::parse_bytes_format ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_provides_fresh_input ... ok
[INFO] [stdout] test cli::unit_tests::parse_defaults ... ok
[INFO] [stdout] test cli::unit_tests::parse_filter ... ok
[INFO] [stdout] test cli::unit_tests::parse_ignored ... ok
[INFO] [stdout] test cli::unit_tests::parse_include_ignored ... ok
[INFO] [stdout] test cli::unit_tests::parse_invalid_output ... ok
[INFO] [stdout] test cli::unit_tests::parse_invalid_sort ... ok
[INFO] [stdout] test cli::unit_tests::parse_invalid_threshold ... ok
[INFO] [stdout] test cli::unit_tests::parse_list ... ok
[INFO] [stdout] test cli::unit_tests::parse_list_terse ... ok
[INFO] [stdout] test cli::unit_tests::parse_min_time_invalid_unit ... ok
[INFO] [stdout] test cli::unit_tests::parse_min_time_default_none ... ok
[INFO] [stdout] test cli::unit_tests::parse_min_time_microseconds ... ok
[INFO] [stdout] test cli::unit_tests::parse_min_time_missing_unit ... ok
[INFO] [stdout] test cli::unit_tests::parse_min_time_milliseconds ... ok
[INFO] [stdout] test cli::unit_tests::parse_min_time_nanoseconds ... ok
[INFO] [stdout] test cli::unit_tests::parse_min_time_seconds ... ok
[INFO] [stdout] test cli::unit_tests::parse_output_json ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_threaded_generator_call_count ... ok
[INFO] [stdout] test cli::unit_tests::parse_save_baseline ... ok
[INFO] [stdout] test cli::unit_tests::parse_skip_overrides_filter ... ok
[INFO] [stdout] test cli::unit_tests::parse_sort ... ok
[INFO] [stdout] test cli::unit_tests::parse_test ... ok
[INFO] [stdout] test cli::unit_tests::parse_threads_invalid ... ok
[INFO] [stdout] test cli::unit_tests::parse_skip ... ok
[INFO] [stdout] test cli::unit_tests::parse_threads_multiple ... ok
[INFO] [stdout] test cli::unit_tests::parse_threads_no_flag ... ok
[INFO] [stdout] test cli::unit_tests::parse_threads_single ... ok
[INFO] [stdout] test cli::unit_tests::parse_threads_zero ... ok
[INFO] [stdout] test cli::unit_tests::parse_threads_zero_mixed ... ok
[INFO] [stdout] test cli::unit_tests::parse_threshold ... ok
[INFO] [stdout] test cli::unit_tests::parse_unknown_flag ... ok
[INFO] [stdout] test config::unit_tests::defaults_all_none ... ok
[INFO] [stdout] test config::unit_tests::overwrite_inheritance ... ok
[INFO] [stdout] test config::unit_tests::overwrite_all_fields_child_wins ... ok
[INFO] [stdout] test config::unit_tests::overwrite_precedence ... ok
[INFO] [stdout] test config::unit_tests::overwrite_threads_child_wins ... ok
[INFO] [stdout] test config::unit_tests::overwrite_threads_inherits_from_parent ... ok
[INFO] [stdout] test cli::unit_tests::parse_min_time_no_number ... ok
[INFO] [stdout] test config::unit_tests::resolve_partial ... ok
[INFO] [stdout] test config::unit_tests::resolve_threads_default_is_single ... ok
[INFO] [stdout] test config::unit_tests::resolve_defaults ... ok
[INFO] [stdout] test config::unit_tests::resolve_threads_sorted_and_deduped ... ok
[INFO] [stdout] test config::unit_tests::resolve_threads_zero_expands_to_available ... ok
[INFO] [stdout] test config::unit_tests::resolve_threads_zero_mixed_with_explicit ... ok
[INFO] [stdout] test config::unit_tests::resolve_threads_explicit_list ... ok
[INFO] [stdout] test counter::collection::unit_tests::mean_count_large_values_no_overflow ... ok
[INFO] [stdout] test counter::collection::unit_tests::mean_count_empty ... ok
[INFO] [stdout] test counter::collection::unit_tests::mean_count_multiple ... ok
[INFO] [stdout] test counter::collection::unit_tests::mean_count_non_integer ... ok
[INFO] [stdout] test counter::collection::unit_tests::counts_returns_raw_values ... ok
[INFO] [stdout] test counter::collection::unit_tests::mean_count_single ... ok
[INFO] [stdout] test counter::collection::unit_tests::new_is_empty ... ok
[INFO] [stdout] test counter::collection::unit_tests::push_increments_len ... ok
[INFO] [stdout] test counter::collection::unit_tests::with_capacity_starts_empty ... ok
[INFO] [stdout] test counter::unit_tests::bytes_count_new ... ok
[INFO] [stdout] test counter::unit_tests::bytes_count_of_slice_u32 ... ok
[INFO] [stdout] test counter::unit_tests::bytes_count_of_slice_u8 ... ok
[INFO] [stdout] test counter::unit_tests::bytes_count_of_slice_zst ... ok
[INFO] [stdout] test counter::unit_tests::bytes_count_of_str ... ok
[INFO] [stdout] test counter::unit_tests::chars_count_new ... ok
[INFO] [stdout] test counter::unit_tests::bytes_count_zero ... ok
[INFO] [stdout] test counter::unit_tests::chars_count_of_str_ascii ... ok
[INFO] [stdout] test counter::unit_tests::chars_count_of_str_emoji ... ok
[INFO] [stdout] test counter::unit_tests::counter_kind_eq ... ok
[INFO] [stdout] test counter::unit_tests::counter_trait_dynamic_dispatch ... ok
[INFO] [stdout] test counter::unit_tests::cycles_count_new ... ok
[INFO] [stdout] test counter::unit_tests::items_count_new ... ok
[INFO] [stdout] test counter::unit_tests::items_count_of_empty_iter ... ok
[INFO] [stdout] test counter::unit_tests::items_count_of_iter ... ok
[INFO] [stdout] test counter::unit_tests::items_count_of_iter_consumes ... ok
[INFO] [stdout] test entry::list::unit_tests::entry_list_push_and_iter ... ok
[INFO] [stdout] test counter::unit_tests::bytes_count_of_str_multibyte ... ok
[INFO] [stdout] test counter::unit_tests::chars_count_of_str_unicode ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_bench_values_threaded_four_threads ... ok
[INFO] [stdout] test entry::tree::unit_tests::entry_tree_retain ... ok
[INFO] [stdout] test bencher::unit_tests::bench_context_zero_threads_panics - should panic ... ok
[INFO] [stdout] test entry::tree::unit_tests::entry_tree_grouping ... ok
[INFO] [stdout] test entry::tree::unit_tests::natural_cmp_ordering ... ok
[INFO] [stdout] test entry::tree::unit_tests::entry_tree_group_options ... ok
[INFO] [stdout] test entry::tree::unit_tests::sort_by_stats_missing_entries_sort_last ... ok
[INFO] [stdout] test entry::tree::unit_tests::sort_by_stats_name_delegates_to_sort ... ok
[INFO] [stdout] test output::csv::unit_tests::csv_output_header_and_row ... ok
[INFO] [stdout] test entry::tree::unit_tests::sort_by_stats_p50 ... ok
[INFO] [stdout] test output::csv::unit_tests::csv_empty_results ... ok
[INFO] [stdout] test output::csv::unit_tests::csv_multiple_rows ... ok
[INFO] [stdout] test output::csv::unit_tests::csv_threads_column_values ... ok
[INFO] [stdout] test output::csv::unit_tests::csv_threads_column_header_present ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_bytes_binary ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_bytes_decimal ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_nan_passthrough ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_infinity_passthrough ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_sub_one ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_neg_infinity_passthrough ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_sub_one_two_sig_figs ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_negative ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_trims_trailing_zeros ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_sub_one_with_leading_zeros ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_negative_sub_one ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_preserves_significant_digits ... ok
[INFO] [stdout] test output::fmt::unit_tests::scale_value_binary_thresholds ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_truncates_not_rounds ... ok
[INFO] [stdout] test output::fmt::unit_tests::format_f64_whole_number ... ok
[INFO] [stdout] test output::fmt::unit_tests::scale_value_nan ... ok
[INFO] [stdout] test output::fmt::unit_tests::scale_value_negative ... ok
[INFO] [stdout] test output::fmt::unit_tests::scale_format_throughput ... ok
[INFO] [stdout] test bencher::unit_tests::bench_refs_threaded_propagates_panic - should panic ... ok
[INFO] [stdout] test bencher::unit_tests::with_inputs_bench_values_threaded_propagates_panic - should panic ... ok
[INFO] [stdout] test bencher::unit_tests::second_run_panics - should panic ... ok
[INFO] [stdout] test output::fmt::unit_tests::scale_value_ranges ... ok
[INFO] [stdout] test output::fmt::unit_tests::throughput_display_bytes ... ok
[INFO] [stdout] test output::fmt::unit_tests::scale_value_zero ... ok
[INFO] [stdout] test output::fmt::unit_tests::scale_format_bytes_format ... ok
[INFO] [stdout] test output::fmt::unit_tests::throughput_display_bytes_binary_exact ... ok
[INFO] [stdout] test output::fmt::unit_tests::throughput_display_bytes_decimal_exact ... ok
[INFO] [stdout] test output::fmt::unit_tests::throughput_display_cycles ... ok
[INFO] [stdout] test output::fmt::unit_tests::throughput_display_width_padding ... ok
[INFO] [stdout] test output::fmt::unit_tests::throughput_display_zero_picos ... ok
[INFO] [stdout] test output::table::unit_tests::ignored_leaf_placeholder ... ok
[INFO] [stdout] test output::table::unit_tests::nested_group_tree_structure ... ok
[INFO] [stdout] test output::table::unit_tests::prefix_restores_after_nested_groups ... ok
[INFO] [stdout] test output::table::unit_tests::table_renders_row ... ok
[INFO] [stdout] test output::table::unit_tests::table_renders_header ... ok
[INFO] [stdout] test output::table::unit_tests::table_tree_structure ... ok
[INFO] [stdout] test output::fmt::unit_tests::throughput_display_items ... ok
[INFO] [stdout] test output::table::unit_tests::write_comparison_row_no_panic ... ok
[INFO] [stdout] test output::fmt::unit_tests::throughput_display_zero_count ... ok
[INFO] [stdout] test stats::exact::unit_tests::mean_basic ... ok
[INFO] [stdout] test stats::exact::unit_tests::mean_single ... ok
[INFO] [stdout] test output::table::unit_tests::write_leaf_with_throughput ... ok
[INFO] [stdout] test stats::exact::unit_tests::compute_all_percentiles ... ok
[INFO] [stdout] test stats::exact::unit_tests::percentile_ten_samples ... ok
[INFO] [stdout] test stats::exact::unit_tests::percentile_single_sample ... ok
[INFO] [stdout] test stats::exact::unit_tests::std_dev_identical_values ... ok
[INFO] [stdout] test stats::exact::unit_tests::std_dev_uniform ... ok
[INFO] [stdout] test stats::exact::unit_tests::percentile_two_samples ... ok
[INFO] [stdout] test stats::hdr::unit_tests::hdr_basic_percentiles ... ok
[INFO] [stdout] test stats::exact::unit_tests::percentile_hundred_samples ... ok
[INFO] [stdout] test stats::hdr::unit_tests::hdr_empty ... ok
[INFO] [stdout] test stats::hdr::unit_tests::hdr_clamps_u128_to_u64 ... ok
[INFO] [stdout] test stats::sample::unit_tests::collection_add_and_sort ... ok
[INFO] [stdout] test stats::sample::unit_tests::collection_new_is_empty ... ok
[INFO] [stdout] test stats::sample::unit_tests::collection_raw_picos_preserves_order ... ok
[INFO] [stdout] test stats::sample::unit_tests::collection_total_duration ... ok
[INFO] [stdout] test stats::hdr::unit_tests::hdr_single_value ... ok
[INFO] [stdout] test stats::sample::unit_tests::collection_total_iterations ... ok
[INFO] [stdout] test stats::sample::unit_tests::collection_with_capacity ... ok
[INFO] [stdout] test stats::unit_tests::compute_stats_exact_mode ... ok
[INFO] [stdout] test stats::unit_tests::compute_stats_exact_mode_unsorted_input ... ok
[INFO] [stdout] test stats::unit_tests::compute_stats_exact_mode_with_sample_size ... ok
[INFO] [stdout] test stats::hdr::unit_tests::hdr_min_max_len ... ok
[INFO] [stdout] test stats::hdr::unit_tests::hdr_mean_and_stddev ... ok
[INFO] [stdout] test stats::exact::unit_tests::percentile_empty_panics - should panic ... ok
[INFO] [stdout] test stats::exact::unit_tests::mean_empty_panics - should panic ... ok
[INFO] [stdout] test stats::exact::unit_tests::percentile_out_of_range_panics - should panic ... ok
[INFO] [stdout] test stats::unit_tests::compute_stats_empty_panics - should panic ... ok
[INFO] [stdout] test stats::exact::unit_tests::std_dev_empty_panics - should panic ... ok
[INFO] [stdout] test stats::unit_tests::compute_stats_zero_sample_size_panics - should panic ... ok
[INFO] [stdout] test stats::unit_tests::sample_sufficiency_all_ok ... ok
[INFO] [stdout] test stats::unit_tests::sample_sufficiency_config_too_low ... ok
[INFO] [stdout] test stats::unit_tests::exact_percentiles_match_uniform_analytical ... ok
[INFO] [stdout] test stats::unit_tests::sample_sufficiency_max_time_truncated ... ok
[INFO] [stdout] test stats::unit_tests::sample_sufficiency_very_low ... ok
[INFO] [stdout] test thread::unit_tests::atomic_flag_default_false ... ok
[INFO] [stdout] test thread::unit_tests::atomic_flag_roundtrip ... ok
[INFO] [stdout] test thread::unit_tests::broadcast_swallows_main_thread_panic ... ok
[INFO] [stdout] test thread::unit_tests::broadcast_swallows_aux_thread_panic ... ok
[INFO] [stdout] test stats::unit_tests::cross_validate_exact_vs_hdr ... ok
[INFO] [stdout] test stats::unit_tests::compute_stats_hdr_mode ... ok
[INFO] [stdout] test stats::unit_tests::hdr_wrapper_matches_raw_hdrhistogram ... ok
[INFO] [stdout] test thread::unit_tests::cache_padded_alignment ... ok
[INFO] [stdout] test thread::unit_tests::cache_padded_deref ... ok
[INFO] [stdout] test thread::unit_tests::cache_padded_deref_mut ... ok
[INFO] [stdout] test thread::unit_tests::par_extend_one_aux ... ok
[INFO] [stdout] test thread::unit_tests::par_extend_panic_leaves_none_slot ... ok
[INFO] [stdout] test thread::unit_tests::cache_padded_size ... ok
[INFO] [stdout] test thread::unit_tests::par_extend_zero_aux ... ok
[INFO] [stdout] test thread::unit_tests::sync_wrap_deref ... ok
[INFO] [stdout] test thread::unit_tests::sync_wrap_deref_mut ... ok
[INFO] [stdout] test thread::unit_tests::sync_wrap_is_sync ... ok
[INFO] [stdout] test thread::unit_tests::par_extend_four_aux ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::arithmetic_add ... ok
[INFO] [stdout] test thread::unit_tests::pool_spawns_lazily ... ok
[INFO] [stdout] test thread::unit_tests::atomic_flag_default_true ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::add_assign ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::arithmetic_sub ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::checked_sub_none ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::display_microseconds ... ok
[INFO] [stdout] test thread::unit_tests::broadcast_with_sleep ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::arithmetic_div_u64 ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::arithmetic_mul_u64 ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::checked_sub_some ... ok
[INFO] [stdout] test thread::unit_tests::broadcast_thread_id_zero_is_main ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::display_milliseconds ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::display_nanoseconds ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::from_duration_micros ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::display_seconds ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::display_picoseconds ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::from_duration_millis ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::ordering ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::from_duration_nanos ... ok
[INFO] [stdout] test time::fine_duration::unit_tests::zero ... ok
[INFO] [stdout] test util::sort::unit_tests::natural_sort_mixed ... ok
[INFO] [stdout] test thread::unit_tests::par_extend_scaling ... ok
[INFO] [stdout] test util::sort::unit_tests::natural_sort_order ... ok
[INFO] [stdout] test time::unit_tests::timer_overhead_nonnegative ... ok
[INFO] [stdout] test time::unit_tests::timer_precision_nonzero ... ok
[INFO] [stdout] test time::unit_tests::measure_precision_nonzero ... ok
[INFO] [stdout] test time::unit_tests::timer_best_available_works ... ok
[INFO] [stdout] test time::unit_tests::instant_timer_measures_time ... ok
[INFO] [stdout] test stats::unit_tests::compute_stats_hdr_boundary ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 241 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.23s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/compile_fail.rs (/opt/rustwide/target/debug/deps/compile_fail-e18d36cd0f8beba3)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]    Compiling libc v0.2.182
[INFO] [stderr]     Checking cfg-if v1.0.4
[INFO] [stderr]    Compiling crossbeam-utils v0.8.21
[INFO] [stderr]    Compiling shlex v1.3.0
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling find-msvc-tools v0.1.9
[INFO] [stderr]    Compiling crc32fast v1.5.0
[INFO] [stderr]    Compiling autocfg v1.5.0
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling cc v1.2.56
[INFO] [stderr]    Compiling unicode-ident v1.0.23
[INFO] [stderr]     Checking adler2 v2.0.1
[INFO] [stderr]     Checking simd-adler32 v0.3.8
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]     Checking miniz_oxide v0.8.9
[INFO] [stderr]    Compiling getrandom v0.4.1
[INFO] [stderr]     Checking minimal-lexical v0.2.1
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]     Checking memchr v2.8.0
[INFO] [stderr]     Checking crossbeam-channel v0.5.15
[INFO] [stderr]     Checking flate2 v1.1.9
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]     Checking nom v7.1.3
[INFO] [stderr]     Checking bitflags v2.11.0
[INFO] [stderr]     Checking once_cell v1.21.3
[INFO] [stderr]     Checking smallvec v1.15.1
[INFO] [stderr]     Checking byteorder v1.5.0
[INFO] [stderr]     Checking linux-raw-sys v0.12.1
[INFO] [stderr]     Checking base64 v0.21.7
[INFO] [stderr]     Checking scopeguard v1.2.0
[INFO] [stderr]     Checking crossbeam-queue v0.3.12
[INFO] [stderr]     Checking lock_api v0.4.14
[INFO] [stderr]     Checking saa v5.5.0
[INFO] [stderr]     Checking seize v0.5.1
[INFO] [stderr]     Checking sdd v4.6.2
[INFO] [stderr]     Checking hashbrown v0.14.5
[INFO] [stderr]     Checking arrayvec v0.7.6
[INFO] [stderr]     Checking static_assertions v1.1.0
[INFO] [stderr]     Checking rustc-hash v2.1.1
[INFO] [stderr]     Checking fastrand v2.3.0
[INFO] [stderr]     Checking scc v3.6.5
[INFO] [stderr]    Compiling libmimalloc-sys v0.1.44
[INFO] [stderr]     Checking dashmap v6.1.0
[INFO] [stderr]     Checking hdrhistogram v7.5.4
[INFO] [stderr]     Checking tempfile v3.26.0
[INFO] [stderr]     Checking mimalloc v0.1.48
[INFO] [stderr]     Checking masstree v0.8.1
[INFO] [stderr]    Compiling pbench-macros v0.2.0 (/opt/rustwide/workdir/pbench-macros)
[INFO] [stderr]     Checking pbench v0.2.0 (/opt/rustwide/workdir/pbench)
[INFO] [stderr] warning: unnecessary `unsafe` block
[INFO] [stderr]   --> /opt/rustwide/workdir/pbench/src/time/tsc.rs:97:9
[INFO] [stderr]    |
[INFO] [stderr] 97 |         unsafe { x86::__cpuid(leaf) }
[INFO] [stderr]    |         ^^^^^^ unnecessary `unsafe` block
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(unused_unsafe)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `pbench` (lib) generated 1 warning
[INFO] [stderr]     Checking pbench-tests v0.0.0 (/opt/rustwide/target/tests/trybuild/pbench)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 31.07s
[INFO] [stderr] 
[INFO] [stderr] 
[INFO] [stdout] test compile_fail ... ok
[INFO] [stderr] test tests/compile_fail/duplicate_option.rs ... ok
[INFO] [stdout] 
[INFO] [stderr] test tests/compile_fail/invalid_args.rs ... ok
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 48.72s
[INFO] [stderr] test tests/compile_fail/unknown_option.rs ... ok
[INFO] [stdout] 
[INFO] [stderr] 
[INFO] [stdout] 
[INFO] [stderr] 
[INFO] [stdout] running 0 tests
[INFO] [stderr]      Running tests/integration.rs (/opt/rustwide/target/debug/deps/integration-f1a71c6ca20338d6)
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/pbench_macros-52bd28de50ba7936)
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests pbench
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test pbench/src/bencher/mod.rs - bencher::Bencher<'ctx>::counter (line 172) ... ignored
[INFO] [stdout] test pbench/src/counter/mod.rs - counter::BytesCount (line 66) ... ignored
[INFO] [stdout] test pbench/src/lib.rs - (line 18) ... ignored
[INFO] [stdout] test pbench/src/lib.rs - (line 47) ... ignored
[INFO] [stdout] test pbench/src/lib.rs - (line 59) ... ignored
[INFO] [stdout] test pbench/src/lib.rs - (line 70) ... ignored
[INFO] [stdout] test pbench/src/lib.rs - (line 82) ... ignored
[INFO] [stdout] test pbench/src/config.rs - config::BenchOptions (line 20) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 7 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.89s; merged doctests compilation took 0.88s
[INFO] [stderr]    Doc-tests pbench_macros
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test pbench-macros/src/lib.rs - bench (line 98) ... ignored
[INFO] [stdout] test pbench-macros/src/lib.rs - bench_group (line 263) ... ignored
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.56s; merged doctests compilation took 0.56s
[INFO] running `Command { std: "docker" "inspect" "481e0b12a2abcfd58de0c784853060f8ccf4ccc395d1b2083eb6f4841a0d34b3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "481e0b12a2abcfd58de0c784853060f8ccf4ccc395d1b2083eb6f4841a0d34b3", kill_on_drop: false }`
[INFO] [stdout] 481e0b12a2abcfd58de0c784853060f8ccf4ccc395d1b2083eb6f4841a0d34b3
